Dec 02 16:08:38.446 INFO testing sonnerie-0.3.4 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 02 16:08:38.447 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:08:38.642 INFO [stdout] e63ccef73b043150a38de5e1c116503405010d6a37fc97abda49baff4f4f9ab2
Dec 02 16:08:38.644 INFO running `"docker" "start" "-a" "e63ccef73b043150a38de5e1c116503405010d6a37fc97abda49baff4f4f9ab2"`
Dec 02 16:08:39.163 INFO [stderr] usermod: no changes
Dec 02 16:08:39.315 INFO [stderr]    Compiling escape_string v0.1.1
Dec 02 16:08:39.315 INFO [stderr]    Compiling range v0.3.1
Dec 02 16:08:39.315 INFO [stderr]    Compiling intrusive-collections v0.7.8
Dec 02 16:08:39.315 INFO [stderr]    Compiling daemonize v0.3.0
Dec 02 16:08:39.315 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 02 16:08:39.315 INFO [stderr]    Compiling rustyline v2.1.0
Dec 02 16:08:41.305 INFO [stderr]    Compiling sonnerie-api v0.3.0
Dec 02 16:08:46.271 INFO [stderr]    Compiling sonnerie v0.3.4 (/source)
Dec 02 16:08:56.131 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.96s
Dec 02 16:08:56.143 INFO [stderr] su: No module specific data is present
Dec 02 16:08:57.040 INFO running `"docker" "inspect" "e63ccef73b043150a38de5e1c116503405010d6a37fc97abda49baff4f4f9ab2"`
Dec 02 16:08:57.343 INFO running `"docker" "rm" "-f" "e63ccef73b043150a38de5e1c116503405010d6a37fc97abda49baff4f4f9ab2"`
Dec 02 16:08:57.695 INFO [stdout] e63ccef73b043150a38de5e1c116503405010d6a37fc97abda49baff4f4f9ab2
Dec 02 16:08:57.697 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:08:58.141 INFO [stdout] 3bc46cce92f0f364952a5fac46b0c27a278c799592244b01deede0f9fa428f26
Dec 02 16:08:58.143 INFO running `"docker" "start" "-a" "3bc46cce92f0f364952a5fac46b0c27a278c799592244b01deede0f9fa428f26"`
Dec 02 16:08:59.724 INFO [stderr] usermod: no changes
Dec 02 16:08:59.907 INFO [stderr]    Compiling sonnerie v0.3.4 (/source)
Dec 02 16:09:10.775 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.01s
Dec 02 16:09:10.787 INFO [stderr] su: No module specific data is present
Dec 02 16:09:11.572 INFO running `"docker" "inspect" "3bc46cce92f0f364952a5fac46b0c27a278c799592244b01deede0f9fa428f26"`
Dec 02 16:09:11.864 INFO running `"docker" "rm" "-f" "3bc46cce92f0f364952a5fac46b0c27a278c799592244b01deede0f9fa428f26"`
Dec 02 16:09:12.283 INFO [stdout] 3bc46cce92f0f364952a5fac46b0c27a278c799592244b01deede0f9fa428f26
Dec 02 16:09:12.283 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-7/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:09:12.704 INFO [stdout] 06d0d10cdb670e99a8194db070d59201e5e365d7a0937e73524eb0955cd87711
Dec 02 16:09:12.706 INFO running `"docker" "start" "-a" "06d0d10cdb670e99a8194db070d59201e5e365d7a0937e73524eb0955cd87711"`
Dec 02 16:09:14.778 INFO [stderr] usermod: no changes
Dec 02 16:09:15.022 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Dec 02 16:09:15.036 INFO [stderr]      Running /target/debug/deps/sonnerie-b97b9ffb1b46346a
Dec 02 16:09:15.041 INFO [stdout] 
Dec 02 16:09:15.041 INFO [stdout] running 33 tests
Dec 02 16:09:15.151 INFO [stdout] test db::tests::backwards_two ... ok
Dec 02 16:09:15.199 INFO [stdout] test db::tests::backwards_break ... ok
Dec 02 16:09:15.252 INFO [stdout] test db::tests::block_overflows ... ok
Dec 02 16:09:15.311 INFO [stdout] test db::tests::blocks_exact_file ... ok
Dec 02 16:09:15.357 INFO [stdout] test db::tests::discard_disk_wal ... ok
Dec 02 16:09:15.357 INFO [stdout] test db::tests::dbmeta1 ... ok
Dec 02 16:09:15.401 INFO [stdout] test db::tests::dump_some ... ok
Dec 02 16:09:15.456 INFO [stdout] test db::tests::erase_ranges1 ... ok
Dec 02 16:09:15.473 INFO [stdout] test db::tests::duplicate_at_once ... ok
Dec 02 16:09:15.480 INFO [stdout] test db::tests::duplicate_seq ... ok
Dec 02 16:09:15.516 INFO [stdout] test db::tests::erase_ranges2 ... ok
Dec 02 16:09:15.531 INFO [stdout] test db::tests::backwards_illegal ... ok
Dec 02 16:09:15.583 INFO [stdout] test db::tests::erase_ranges3 ... ok
Dec 02 16:09:15.737 INFO [stdout] test db::tests::erase_ranges4 ... ok
Dec 02 16:09:15.738 INFO [stdout] test db::tests::insertion_bulk ... ok
Dec 02 16:09:15.749 INFO [stdout] test db::tests::erase_ranges5 ... ok
Dec 02 16:09:15.791 INFO [stdout] test db::tests::generation_increases ... ok
Dec 02 16:09:15.806 INFO [stderr] thread 'sonnerie-merge' panicked at 'called `Result::unwrap()` on an `Err` value: Os { code: 2, kind: NotFound, message: "No such file or directory" }', libcore/result.rs:1009:5
Dec 02 16:09:15.806 INFO [stderr] stack backtrace:
Dec 02 16:09:15.806 INFO [stderr]    0:     0x55a77aaa08ff - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 02 16:09:15.806 INFO [stderr]    1:     0x55a77aaa9d17 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/sys_common/backtrace.rs:71
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/sys_common/backtrace.rs:59
Dec 02 16:09:15.806 INFO [stderr]    2:     0x55a77aaa492f - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/panicking.rs:211
Dec 02 16:09:15.806 INFO [stderr]    3:     0x55a77aaa4694 - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/panicking.rs:227
Dec 02 16:09:15.806 INFO [stderr]    4:     0x55a77aaa500e - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/panicking.rs:476
Dec 02 16:09:15.806 INFO [stderr]    5:     0x55a77aaa4bb1 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/panicking.rs:390
Dec 02 16:09:15.806 INFO [stderr]    6:     0x55a77aaa4a95 - rust_begin_unwind
Dec 02 16:09:15.806 INFO [stderr]                                at libstd/panicking.rs:325
Dec 02 16:09:15.806 INFO [stderr]    7:     0x55a77aaeaaec - core::panicking::panic_fmt::h6f9cebf5e7474a0b
Dec 02 16:09:15.807 INFO [stderr]                                at libcore/panicking.rs:77
Dec 02 16:09:15.807 INFO [stderr]    8:     0x55a77aa30280 - core::result::unwrap_failed::h3a1b7e74f4172a6f
Dec 02 16:09:15.807 INFO [stderr]                                at libcore/macros.rs:26
Dec 02 16:09:15.807 INFO [stderr]    9:     0x55a77aa2ff11 - <core::result::Result<T, E>>::unwrap::h7a4df1b1e955e1ed
Dec 02 16:09:15.807 INFO [stderr]                                at libcore/result.rs:808
Dec 02 16:09:15.807 INFO [stderr]   10:     0x55a77a9998b1 - sonnerie::db::Db::start_merge_thread::{{closure}}::he797fe51a1c28f3b
Dec 02 16:09:15.808 INFO [stderr]                                at src/db.rs:145
Dec 02 16:09:15.808 INFO [stderr]   11:     0x55a77a9b0427 - std::sys_common::backtrace::__rust_begin_short_backtrace::hf65fef695ae609ec
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/sys_common/backtrace.rs:136
Dec 02 16:09:15.811 INFO [stderr]   12:     0x55a77a99e6b4 - std::thread::Builder::spawn::{{closure}}::{{closure}}::h16c072fb495c6ca7
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/thread/mod.rs:409
Dec 02 16:09:15.811 INFO [stderr]   13:     0x55a77a9b01f7 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h8099c708796e17d4
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/panic.rs:313
Dec 02 16:09:15.811 INFO [stderr]   14:     0x55a77a99718f - std::panicking::try::do_call::ha92e52fe8be84cbd
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/panicking.rs:310
Dec 02 16:09:15.811 INFO [stderr]   15:     0x55a77aab4cc9 - __rust_maybe_catch_panic
Dec 02 16:09:15.811 INFO [stderr]                                at libpanic_unwind/lib.rs:102
Dec 02 16:09:15.811 INFO [stderr]   16:     0x55a77a99708f - std::panicking::try::h94c99828a693d1d1
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/panicking.rs:289
Dec 02 16:09:15.811 INFO [stderr]   17:     0x55a77a9b06e7 - std::panic::catch_unwind::hf97f9b25fee1da41
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/panic.rs:392
Dec 02 16:09:15.811 INFO [stderr]   18:     0x55a77a99e4c8 - std::thread::Builder::spawn::{{closure}}::hf65d5dfe4f8f9bc9
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/thread/mod.rs:408
Dec 02 16:09:15.811 INFO [stderr]   19:     0x55a77a99f09f - <F as alloc::boxed::FnBox<A>>::call_box::h1e86f81540f49c71
Dec 02 16:09:15.811 INFO [stderr]                                at liballoc/boxed.rs:672
Dec 02 16:09:15.811 INFO [stderr]   20:     0x55a77aaa27bd - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 02 16:09:15.811 INFO [stderr]                                at liballoc/boxed.rs:682
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/sys_common/thread.rs:24
Dec 02 16:09:15.811 INFO [stderr]   21:     0x55a77aa96ad5 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 02 16:09:15.811 INFO [stderr]                                at libstd/sys/unix/thread.rs:90
Dec 02 16:09:15.811 INFO [stderr]   22:     0x7fc7de1b06b9 - start_thread
Dec 02 16:09:15.811 INFO [stderr]   23:     0x7fc7ddcd041c - clone
Dec 02 16:09:15.811 INFO [stderr]   24:                0x0 - <unknown>
Dec 02 16:09:15.851 INFO [stdout] test db::tests::restart_offset ... FAILED
Dec 02 16:09:15.925 INFO [stdout] test db::tests::save_disk_wal ... ok
Dec 02 16:09:15.927 INFO [stdout] test db::tests::select_weird_ranges ... ok
Dec 02 16:09:15.964 INFO [stdout] test db::tests::two_series ... ok
Dec 02 16:09:15.967 INFO [stdout] test metadata::tests::timestamp_range ... ok
Dec 02 16:09:15.967 INFO [stdout] test wal::tests::rw1 ... ok
Dec 02 16:09:15.967 INFO [stdout] test wal::tests::rw2 ... ok
Dec 02 16:09:15.967 INFO [stdout] test wal::tests::rw3 ... ok
Dec 02 16:09:15.967 INFO [stdout] test wal::tests::wal_overlap ... ok
Dec 02 16:09:16.026 INFO [stdout] test db::tests::stored_offset ... ok
Dec 02 16:09:16.047 INFO [stdout] test db::tests::write_should_panic ... ok
Dec 02 16:09:16.091 INFO [stdout] test db::tests::two_tx ... ok
Dec 02 16:09:16.139 INFO [stdout] test db::tests::two_tx_reopen ... ok
Dec 02 16:09:16.747 INFO [stdout] test db::tests::read_direction_multi_block ... ok
Dec 02 16:10:07.452 INFO [stdout] test db::tests::boundary_crossing ... ok
Dec 02 16:10:15.052 INFO [stdout] test db::tests::boundary_crossing_bulk_load ... test db::tests::boundary_crossing_bulk_load has been running for over 60 seconds
Dec 02 16:15:15.061 ERRO error running command: no output for 300 seconds
Dec 02 16:15:15.061 INFO running `"docker" "inspect" "06d0d10cdb670e99a8194db070d59201e5e365d7a0937e73524eb0955cd87711"`
Dec 02 16:15:15.369 INFO running `"docker" "rm" "-f" "06d0d10cdb670e99a8194db070d59201e5e365d7a0937e73524eb0955cd87711"`
Dec 02 16:15:16.867 INFO [stdout] 06d0d10cdb670e99a8194db070d59201e5e365d7a0937e73524eb0955cd87711
